[INFO] cloning repository https://github.com/philomont/fmrl
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/philomont/fmrl" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fphilomont%2Ffmrl", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fphilomont%2Ffmrl'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 7373a693b996635528ee4394495300c756d9e967
[INFO] testing philomont/fmrl against master#1f7f8ea0721a3b1eb73e6c6d25cccb371434b320 for pr-154065-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fphilomont%2Ffmrl" "/workspace/builds/worker-1-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-1-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/philomont/fmrl
[INFO] finished tweaking git repo https://github.com/philomont/fmrl
[INFO] tweaked toml for git repo https://github.com/philomont/fmrl written to /workspace/builds/worker-1-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/philomont/fmrl on toolchain 1f7f8ea0721a3b1eb73e6c6d25cccb371434b320
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/philomont/fmrl already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Blocking waiting for file lock on package cache
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 7dde7dcb69b3199b6c33caa06a22bcdc24a94239bebeb5a2eef64bbe7e5be381
[INFO] running `Command { std: "docker" "start" "-a" "7dde7dcb69b3199b6c33caa06a22bcdc24a94239bebeb5a2eef64bbe7e5be381", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "7dde7dcb69b3199b6c33caa06a22bcdc24a94239bebeb5a2eef64bbe7e5be381", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7dde7dcb69b3199b6c33caa06a22bcdc24a94239bebeb5a2eef64bbe7e5be381", kill_on_drop: false }`
[INFO] [stdout] 7dde7dcb69b3199b6c33caa06a22bcdc24a94239bebeb5a2eef64bbe7e5be381
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] c9cbd5950d34b581de815e665d2132610c9a2e630ff8b62cf230dffab9b21862
[INFO] running `Command { std: "docker" "start" "-a" "c9cbd5950d34b581de815e665d2132610c9a2e630ff8b62cf230dffab9b21862", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.106
[INFO] [stderr]    Compiling quote v1.0.45
[INFO] [stderr]    Compiling memchr v2.8.0
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling fmrl v0.9.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: unused import: `consolidation_step_with_age`
[INFO] [stdout]  --> src/encode.rs:7:61
[INFO] [stdout]   |
[INFO] [stdout] 7 |     age_by_bleaching, age_by_consolidation, age_by_erosion, consolidation_step_with_age,
[INFO] [stdout]   |                                                             ^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout]   |
[INFO] [stdout]   = note: `#[warn(unused_imports)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `max_age_in_region` is never used
[INFO] [stdout]    --> src/age.rs:382:4
[INFO] [stdout]     |
[INFO] [stdout] 382 | fn max_age_in_region(
[INFO] [stdout]     |    ^^^^^^^^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `is_close_to_paper` is never used
[INFO] [stdout]    --> src/decay.rs:115:4
[INFO] [stdout]     |
[INFO] [stdout] 115 | fn is_close_to_paper(r: u8, g: u8, b: u8, paper_r: u8, paper_g: u8, paper_b: u8) -> bool {
[INFO] [stdout]     |    ^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 8.75s
[INFO] running `Command { std: "docker" "inspect" "c9cbd5950d34b581de815e665d2132610c9a2e630ff8b62cf230dffab9b21862", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "c9cbd5950d34b581de815e665d2132610c9a2e630ff8b62cf230dffab9b21862", kill_on_drop: false }`
[INFO] [stdout] c9cbd5950d34b581de815e665d2132610c9a2e630ff8b62cf230dffab9b21862
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 73f5f873b9c6a1614d6e4753634fb9d0aed79e58b9d09b0f147a1adfee3d6cf7
[INFO] running `Command { std: "docker" "start" "-a" "73f5f873b9c6a1614d6e4753634fb9d0aed79e58b9d09b0f147a1adfee3d6cf7", kill_on_drop: false }`
[INFO] [stdout] warning: unused import: `consolidation_step_with_age`
[INFO] [stdout]  --> src/encode.rs:7:61
[INFO] [stdout]   |
[INFO] [stdout] 7 |     age_by_bleaching, age_by_consolidation, age_by_erosion, consolidation_step_with_age,
[INFO] [stdout]   |                                                             ^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout]   |
[INFO] [stdout]   = note: `#[warn(unused_imports)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `max_age_in_region` is never used
[INFO] [stdout]    --> src/age.rs:382:4
[INFO] [stdout]     |
[INFO] [stdout] 382 | fn max_age_in_region(
[INFO] [stdout]     |    ^^^^^^^^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `is_close_to_paper` is never used
[INFO] [stdout]    --> src/decay.rs:115:4
[INFO] [stdout]     |
[INFO] [stdout] 115 | fn is_close_to_paper(r: u8, g: u8, b: u8, paper_r: u8, paper_g: u8, paper_b: u8) -> bool {
[INFO] [stdout]     |    ^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]    Compiling fmrl v0.9.0 (/opt/rustwide/workdir)
[INFO] [stdout] warning: unused import: `consolidation_step_with_age`
[INFO] [stdout]  --> src/encode.rs:7:61
[INFO] [stdout]   |
[INFO] [stdout] 7 |     age_by_bleaching, age_by_consolidation, age_by_erosion, consolidation_step_with_age,
[INFO] [stdout]   |                                                             ^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stdout]   |
[INFO] [stdout]   = note: `#[warn(unused_imports)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `max_age_in_region` is never used
[INFO] [stdout]    --> src/age.rs:382:4
[INFO] [stdout]     |
[INFO] [stdout] 382 | fn max_age_in_region(
[INFO] [stdout]     |    ^^^^^^^^^^^^^^^^^
[INFO] [stdout]     |
[INFO] [stdout]     = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] warning: function `is_close_to_paper` is never used
[INFO] [stdout]    --> src/decay.rs:115:4
[INFO] [stdout]     |
[INFO] [stdout] 115 | fn is_close_to_paper(r: u8, g: u8, b: u8, paper_r: u8, paper_g: u8, paper_b: u8) -> bool {
[INFO] [stdout]     |    ^^^^^^^^^^^^^^^^^
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.06s
[INFO] running `Command { std: "docker" "inspect" "73f5f873b9c6a1614d6e4753634fb9d0aed79e58b9d09b0f147a1adfee3d6cf7", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "73f5f873b9c6a1614d6e4753634fb9d0aed79e58b9d09b0f147a1adfee3d6cf7", kill_on_drop: false }`
[INFO] [stdout] 73f5f873b9c6a1614d6e4753634fb9d0aed79e58b9d09b0f147a1adfee3d6cf7
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+1f7f8ea0721a3b1eb73e6c6d25cccb371434b320" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 5edee02a843a74d16e23daefc1fde09d8c5e7b71b5f78864acbc8e2fe96a8489
[INFO] running `Command { std: "docker" "start" "-a" "5edee02a843a74d16e23daefc1fde09d8c5e7b71b5f78864acbc8e2fe96a8489", kill_on_drop: false }`
[INFO] [stderr] warning: unused import: `consolidation_step_with_age`
[INFO] [stderr]  --> src/encode.rs:7:61
[INFO] [stderr]   |
[INFO] [stderr] 7 |     age_by_bleaching, age_by_consolidation, age_by_erosion, consolidation_step_with_age,
[INFO] [stderr]   |                                                             ^^^^^^^^^^^^^^^^^^^^^^^^^^^
[INFO] [stderr]   |
[INFO] [stderr]   = note: `#[warn(unused_imports)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: function `max_age_in_region` is never used
[INFO] [stderr]    --> src/age.rs:382:4
[INFO] [stderr]     |
[INFO] [stderr] 382 | fn max_age_in_region(
[INFO] [stderr]     |    ^^^^^^^^^^^^^^^^^
[INFO] [stderr]     |
[INFO] [stderr]     = note: `#[warn(dead_code)]` (part of `#[warn(unused)]`) on by default
[INFO] [stderr] 
[INFO] [stderr] warning: function `is_close_to_paper` is never used
[INFO] [stderr]    --> src/decay.rs:115:4
[INFO] [stderr]     |
[INFO] [stderr] 115 | fn is_close_to_paper(r: u8, g: u8, b: u8, paper_r: u8, paper_g: u8, paper_b: u8) -> bool {
[INFO] [stderr]     |    ^^^^^^^^^^^^^^^^^
[INFO] [stderr] 
[INFO] [stderr] warning: `fmrl` (lib) generated 3 warnings (run `cargo fix --lib -p fmrl` to apply 1 suggestion)
[INFO] [stderr] warning: `fmrl` (lib test) generated 3 warnings (3 duplicates)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.07s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/fmrl-ec4177bd69c526ab)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stderr]      Running tests/age_mutation.rs (/opt/rustwide/target/debug/deps/age_mutation-ee5087a95e7924a9)
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test age_chunk_range_correct ... ok
[INFO] [stdout] test last_view_updated_after_render ... ok
[INFO] [stdout] test fade_level_incremented_after_render ... ok
[INFO] [stderr]      Running tests/aging_decay.rs (/opt/rustwide/target/debug/deps/aging_decay-1f533146060d3097)
[INFO] [stdout] test age_crc_valid_after_mutation ... ok
[INFO] [stdout] test noise_seed_not_modified ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test single_pixel_erased_in_one_step ... ok
[INFO] [stdout] test checkerboard_converges_to_all_paper ... ok
[INFO] [stdout] test aging_never_introduces_non_paper ... ok
[INFO] [stdout] test aging_converges_to_all_paper ... ok
[INFO] [stdout] test aging_reduces_file_size_over_many_steps ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.11s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/chunk_parse.rs (/opt/rustwide/target/debug/deps/chunk_parse-82afe486342208a2)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test invalid_magic_detected ... ok
[INFO] [stdout] test unexpected_eof_detected ... ok
[INFO] [stdout] test crc_corruption_detected ... ok
[INFO] [stdout] test unknown_chunk_skipped ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/consolidation_mixed_ages.rs (/opt/rustwide/target/debug/deps/consolidation_mixed_ages-2ba63436241a23c3)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test test_consolidation_mixed_ages ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.07s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/decay_det.rs (/opt/rustwide/target/debug/deps/decay_det-7654380a16811e7a)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test different_time_produces_different_output ... ok
[INFO] [stdout] test same_age_bytes_produce_identical_output ... ok
[INFO] [stdout] test no_decay_at_creation_time ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/roundtrip.rs (/opt/rustwide/target/debug/deps/roundtrip-78eaf8fe769f982f)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test checkerboard_roundtrip ... ok
[INFO] [stdout] test meta_roundtrip ... ok
[INFO] [stdout] test age_entries_initialized ... ok
[INFO] [stdout] test solid_roundtrip ... ok
[INFO] [stdout] test rgb_visualization_format ... ok
[INFO] [stdout] test paper_pixel_format ... ok
[INFO] [stdout] test algorithm_stacking ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/two_squares_different_ages.rs (/opt/rustwide/target/debug/deps/two_squares_different_ages-db47ea5bc9b118a6)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test test_two_squares_different_ages ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.08s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests fmrl
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "5edee02a843a74d16e23daefc1fde09d8c5e7b71b5f78864acbc8e2fe96a8489", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "5edee02a843a74d16e23daefc1fde09d8c5e7b71b5f78864acbc8e2fe96a8489", kill_on_drop: false }`
[INFO] [stdout] 5edee02a843a74d16e23daefc1fde09d8c5e7b71b5f78864acbc8e2fe96a8489
